← Index
NYTProf Performance Profile   « line view »
For rbm/rbm
  Run on Wed Feb 12 03:38:15 2020
Reported on Wed Feb 12 04:56:37 2020

Filename/root/tor-browser-build/input text
StatementsExecuted 1425688 statements in 8.59s
Subroutines
Calls P F Exclusive
Time		 Inclusive
Time		 Subroutine
146492114.17s14272sTemplate::Document::::__ANON__[input text:9]Template::Document::__ANON__[input text:9]
1991111.56s632sTemplate::Document::::__ANON__[input text:43]Template::Document::__ANON__[input text:43]
384174111.36s1.36sTemplate::Document::::CORE:substTemplate::Document::CORE:subst (opcode)
384174111.10s1.10sTemplate::Document::::CORE:matchTemplate::Document::CORE:match (opcode)
100811401ms408sTemplate::Document::::__ANON__[input text:35]Template::Document::__ANON__[input text:35]
798211366ms12599sTemplate::Document::::__ANON__[input text:10]Template::Document::__ANON__[input text:10]
199011263ms11751sTemplate::Document::::__ANON__[input text:18]Template::Document::__ANON__[input text:18]
199111262ms235sTemplate::Document::::__ANON__[input text:17]Template::Document::__ANON__[input text:17]
297811232ms228sTemplate::Document::::__ANON__[input text:13]Template::Document::__ANON__[input text:13]
199111213ms4.80sTemplate::Document::::__ANON__[input text:22]Template::Document::__ANON__[input text:22]
99511122ms15.7sTemplate::Document::::__ANON__[input text:30]Template::Document::__ANON__[input text:30]
28111.58ms4.95sTemplate::Document::::__ANON__[input text:11]Template::Document::__ANON__[input text:11]
9111.33ms1.97sTemplate::Document::::__ANON__[input text:21]Template::Document::__ANON__[input text:21]
111192µs23.7msTemplate::Document::::__ANON__[input text:25]Template::Document::__ANON__[input text:25]
11159µs2.76msTemplate::Document::::__ANON__[input text:12]Template::Document::__ANON__[input text:12]
Call graph for these subroutines as a Graphviz dot language file.
Line State
ments		 Time
on line		 Calls Time
in subs		 Code
12133872.43s29329252037s
# spent 14272s (4.17+14267) within Template::Document::__ANON__[input text:9] which was called 146492 times, avg 97.4ms/call: # 146492 times (4.17s+14267s) by Template::Document::process at line 163 of Template/Document.pm, avg 97.4ms/call # spent 2.76ms (59µs+2.70) within Template::Document::__ANON__[input text:12] which was called: # once (59µs+2.70ms) by Template::Document::process at line 163 of Template/Document.pm # spent 4.80s (213ms+4.59) within Template::Document::__ANON__[input text:22] which was called 1991 times, avg 2.41ms/call: # 1991 times (213ms+4.59s) by Template::Document::process at line 163 of Template/Document.pm, avg 2.41ms/call # spent 632s (1.56+630) within Template::Document::__ANON__[input text:43] which was called 1991 times, avg 317ms/call: # 1991 times (1.56s+630s) by Template::Document::process at line 163 of Template/Document.pm, avg 317ms/call # spent 11751s (263ms+11751) within Template::Document::__ANON__[input text:18] which was called 1990 times, avg 5.91s/call: # 1990 times (263ms+11751s) by Template::Document::process at line 163 of Template/Document.pm, avg 5.91s/call # spent 23.7ms (192µs+23.5) within Template::Document::__ANON__[input text:25] which was called: # once (192µs+23.5ms) by Template::Document::process at line 163 of Template/Document.pm # spent 235s (262ms+235) within Template::Document::__ANON__[input text:17] which was called 1991 times, avg 118ms/call: # 1991 times (262ms+235s) by Template::Document::process at line 163 of Template/Document.pm, avg 118ms/call # spent 408s (401ms+407) within Template::Document::__ANON__[input text:35] which was called 1008 times, avg 405ms/call: # 1008 times (401ms+407s) by Template::Document::process at line 163 of Template/Document.pm, avg 405ms/call # spent 1.97s (1.33ms+1.97) within Template::Document::__ANON__[input text:21] which was called 9 times, avg 219ms/call: # 9 times (1.33ms+1.97s) by Template::Document::process at line 163 of Template/Document.pm, avg 219ms/call # spent 15.7s (122ms+15.5) within Template::Document::__ANON__[input text:30] which was called 995 times, avg 15.7ms/call: # 995 times (122ms+15.5s) by Template::Document::process at line 163 of Template/Document.pm, avg 15.7ms/call # spent 228s (232ms+228) within Template::Document::__ANON__[input text:13] which was called 2978 times, avg 76.7ms/call: # 2978 times (232ms+228s) by Template::Document::process at line 163 of Template/Document.pm, avg 76.7ms/call # spent 4.95s (1.58ms+4.95) within Template::Document::__ANON__[input text:11] which was called 28 times, avg 177ms/call: # 28 times (1.58ms+4.95s) by Template::Document::process at line 163 of Template/Document.pm, avg 177ms/call # spent 12599s (366ms+12599) within Template::Document::__ANON__[input text:10] which was called 7982 times, avg 1.58s/call: # 7982 times (366ms+12599s) by Template::Document::process at line 163 of Template/Document.pm, avg 1.58s/call
$output .= $stash->get('project');
# spent 25241s making 194420 calls to Template::Stash::XS::get, avg 130ms/call # spent 25207s making 77850 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 324ms/call # spent 1720s making 998 calls to Template::Context::include, avg 1.72s/call, recursion: max depth 1, sum of overlapping time 163s # spent 32.0s making 3982 calls to RBM::__ANON__[rbm/lib/RBM.pm:645], avg 8.05ms/call # spent 477ms making 4007 calls to RBM::__ANON__[rbm/lib/RBM.pm:655], avg 119µs/call # spent 33.8ms making 3981 calls to Template::VMethods::text_substr, avg 8µs/call # spent 16.4ms making 6063 calls to Template::Stash::undefined, avg 3µs/call # spent 12.4ms making 1991 calls to Template::Stash::XS::set, avg 6µs/call
2203256555ms47703769s } };
# spent 384s making 23851 calls to Template::Stash::XS::get, avg 16.1ms/call # spent 203s making 2977 calls to RBM::__ANON__[rbm/lib/RBM.pm:648], avg 68.2ms/call # spent 181s making 18883 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 9.59ms/call # spent 4.89ms making 1992 calls to Template::Stash::undefined, avg 2µs/call
3172395222ms17915142s if ($@) {
# spent 71.3s making 13935 calls to Template::Stash::XS::get, avg 5.11ms/call # spent 43.9s making 2987 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 14.7ms/call # spent 27.2s making 993 calls to RBM::__ANON__[rbm/lib/RBM.pm:645], avg 27.4ms/call
42395576.3ms705611.2s $_tt_error = $context->catch($@, \$output);
# spent 5.60s making 5011 calls to Template::Stash::XS::get, avg 1.12ms/call # spent 5.56s making 2009 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 2.77ms/call # spent 912µs making 18 calls to String::ShellQuote::shell_quote, avg 51µs/call # spent 103µs making 18 calls to Template::Stash::XS::set, avg 6µs/call
51099145.1ms800711.3s die $_tt_error unless $_tt_error->type eq 'return';
# spent 5.67s making 2005 calls to Template::Stash::XS::get, avg 2.83ms/call # spent 5.64s making 2005 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 2.81ms/call # spent 29.2ms making 3982 calls to Template::Iterator::get_next, avg 7µs/call # spent 50µs making 15 calls to Template::Stash::undefined, avg 3µs/call
616933203ms24907389s }
# spent 195s making 11955 calls to Template::Stash::XS::get, avg 16.3ms/call # spent 179s making 8971 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 19.9ms/call # spent 15.9s making 1991 calls to RBM::__ANON__[rbm/lib/RBM.pm:645], avg 7.99ms/call # spent 6.63ms making 1990 calls to Template::Stash::undefined, avg 3µs/call
727928160ms1797121972s
# spent 10986s making 8990 calls to Template::Stash::XS::get, avg 1.22s/call # spent 10970s making 6990 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 1.57s/call # spent 15.7s making 1991 calls to RBM::__ANON__[rbm/lib/RBM.pm:645], avg 7.89ms/call
8297990921ms15015148s return $output;
# spent 73.9s making 7010 calls to Template::Stash::XS::get, avg 10.5ms/call # spent 73.8s making 5997 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 12.3ms/call # spent 5.81ms making 1991 calls to Template::Stash::XS::set, avg 3µs/call # spent 933µs making 15 calls to String::ShellQuote::shell_quote, avg 62µs/call # spent 4µs making 2 calls to Template::Stash::undefined, avg 2µs/call
922969204ms89871354s}
# spent 677s making 3996 calls to Template::Stash::XS::get, avg 169ms/call # spent 676s making 3001 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 225ms/call # spent 1.21s making 995 calls to RBM::__ANON__[rbm/lib/RBM.pm:645], avg 1.21ms/call # spent 3.25ms making 995 calls to Template::Stash::undefined, avg 3µs/call
10158487797ms50225.89s
# spent 2.94s making 1016 calls to Template::Stash::XS::get, avg 2.90ms/call # spent 2.93s making 1016 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 2.89ms/call # spent 13.9ms making 981 calls to Template::Iterator::get_next, avg 14µs/call # spent 2.67ms making 1991 calls to UNIVERSAL::isa, avg 1µs/call # spent 90µs making 15 calls to Template::Stash::XS::set, avg 6µs/call # spent 11µs making 3 calls to Template::Stash::undefined, avg 4µs/call
111105401.92s2220321194s
# spent 597s making 126452 calls to Template::Stash::XS::get, avg 4.73ms/call # spent 547s making 31867 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 17.2ms/call # spent 27.6s making 19910 calls to RBM::__ANON__[rbm/lib/RBM.pm:650], avg 1.39ms/call # spent 22.0s making 7964 calls to RBM::__ANON__[rbm/lib/RBM.pm:645], avg 2.77ms/call # spent 127ms making 1991 calls to Template::Config::iterator, avg 64µs/call # spent 102ms making 23893 calls to Template::Stash::XS::set, avg 4µs/call # spent 77.9ms making 1991 calls to Template::VMethods::list_sort, avg 39µs/call # spent 34.6ms making 1991 calls to Template::Iterator::AUTOLOAD, avg 17µs/call # spent 24.1ms making 1991 calls to Template::VMethods::list_import, avg 12µs/call # spent 11.8ms making 3982 calls to Template::Stash::undefined, avg 3µs/call
121200359.6ms20613.71s
# spent 1.86s making 1036 calls to Template::Stash::XS::get, avg 1.80ms/call # spent 1.85s making 1025 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 1.81ms/call
13600536.8ms34802.47s
# spent 1.24s making 1008 calls to Template::Stash::XS::get, avg 1.23ms/call # spent 1.22s making 1008 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 1.21ms/call # spent 4.38ms making 993 calls to Template::Stash::XS::set, avg 4µs/call # spent 2.29ms making 471 calls to Template::Stash::undefined, avg 5µs/call
1427908180ms19940325ms
# spent 324ms making 19910 calls to Template::Iterator::get_next, avg 16µs/call # spent 282µs making 15 calls to Template::Stash::XS::get, avg 19µs/call # spent 129µs making 15 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 9µs/call
15700455.5ms69826.63s
# spent 2.68s making 2988 calls to Template::Stash::XS::get, avg 898µs/call # spent 2.67s making 995 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 2.68ms/call # spent 1.23s making 15 calls to Template::Context::include, avg 82.3ms/call # spent 40.7ms making 1991 calls to Template::Iterator::get_first, avg 20µs/call # spent 1.54ms making 993 calls to UNIVERSAL::isa, avg 2µs/call
161195964.1ms596996.3ms
# spent 54.7ms making 993 calls to Template::Config::iterator, avg 55µs/call # spent 22.9ms making 1992 calls to Template::Stash::XS::get, avg 11µs/call # spent 13.2ms making 993 calls to Template::Iterator::AUTOLOAD, avg 13µs/call # spent 5.51ms making 1991 calls to Template::Stash::undefined, avg 3µs/call
17895796.3ms89522.21s
# spent 1.10s making 993 calls to Template::Stash::XS::get, avg 1.11ms/call # spent 1.09s making 993 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 1.10ms/call # spent 16.8ms making 3982 calls to Template::Stash::XS::set, avg 4µs/call # spent 3.04ms making 993 calls to Template::Stash::undefined, avg 3µs/call # spent 1.31ms making 1991 calls to UNIVERSAL::isa, avg 660ns/call
18697240.1ms49922.39s
# spent 1.17s making 1003 calls to Template::Stash::XS::get, avg 1.16ms/call # spent 1.14s making 999 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 1.14ms/call # spent 82.4ms making 1991 calls to Template::Config::iterator, avg 41µs/call # spent 3.45ms making 995 calls to Template::Stash::undefined, avg 3µs/call # spent 79µs making 4 calls to RBM::__ANON__[rbm/lib/RBM.pm:655], avg 20µs/call
19697020.2ms
20745936.2ms348056.4ms
# spent 37.5ms making 1991 calls to Template::Iterator::AUTOLOAD, avg 19µs/call # spent 17.3ms making 993 calls to Template::Iterator::get_first, avg 17µs/call # spent 1.53ms making 488 calls to Template::Stash::XS::get, avg 3µs/call # spent 100µs making 4 calls to RBM::__ANON__[rbm/lib/RBM.pm:655], avg 25µs/call # spent 39µs making 4 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 10µs/call
21845838.6ms206021.2ms
# spent 16.4ms making 1023 calls to Template::Stash::XS::get, avg 16µs/call # spent 4.46ms making 1007 calls to Template::Stash::undefined, avg 4µs/call # spent 312µs making 30 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 10µs/call
22453224.8ms36211.90s
# spent 929ms making 75 calls to Template::Stash::XS::get, avg 12.4ms/call # spent 927ms making 60 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 15.4ms/call # spent 39.0ms making 1991 calls to Template::Iterator::get_first, avg 20µs/call # spent 4.55ms making 993 calls to Template::Stash::XS::set, avg 5µs/call # spent 3.08ms making 480 calls to Template::Iterator::get_next, avg 6µs/call # spent 1.08ms making 15 calls to RBM::__ANON__[rbm/lib/RBM.pm:655], avg 72µs/call # spent 32µs making 7 calls to Template::Stash::undefined, avg 5µs/call
23699643.8ms542270.9ms
# spent 56.2ms making 2711 calls to Template::Stash::XS::get, avg 21µs/call # spent 10.9ms making 720 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 15µs/call # spent 3.75ms making 1991 calls to Template::Stash::undefined, avg 2µs/call
24625849.7ms47132.04s
# spent 1.03s making 1001 calls to Template::Stash::XS::get, avg 1.03ms/call # spent 1.01s making 997 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 1.01ms/call # spent 6.73ms making 1991 calls to Template::Stash::XS::set, avg 3µs/call # spent 1.87ms making 720 calls to Template::Stash::undefined, avg 3µs/call # spent 134µs making 4 calls to RBM::__ANON__[rbm/lib/RBM.pm:655], avg 34µs/call
2518198.94ms89320.9ms
# spent 15.5ms making 303 calls to Template::Stash::XS::get, avg 51µs/call # spent 4.62ms making 303 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 15µs/call # spent 804µs making 273 calls to Template::Stash::XS::set, avg 3µs/call # spent 31µs making 14 calls to Template::Stash::undefined, avg 2µs/call
26425898.9ms4965341s
# spent 170s making 2979 calls to Template::Stash::XS::get, avg 57.2ms/call # spent 170s making 1986 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 85.8ms/call
272244245.3ms273415µs
# spent 415µs making 273 calls to UNIVERSAL::isa, avg 2µs/call
285464.11ms54616.0ms
# spent 12.0ms making 273 calls to Template::Config::iterator, avg 44µs/call # spent 4.00ms making 273 calls to Template::Iterator::AUTOLOAD, avg 15µs/call
2920186.42ms
- -
3112686.39ms
32273882µs2735.36ms
# spent 5.36ms making 273 calls to Template::Iterator::get_first, avg 20µs/call
33253764.0ms102285.04s
# spent 2.54s making 4255 calls to Template::Stash::XS::get, avg 598µs/call # spent 2.45s making 1991 calls to RBM::__ANON__[rbm/lib/RBM.pm:645], avg 1.23ms/call # spent 43.9ms making 1991 calls to RBM::__ANON__[rbm/lib/RBM.pm:640], avg 22µs/call # spent 6.42ms making 1991 calls to Template::Stash::undefined, avg 3µs/call
34200123.1ms273979µs
# spent 979µs making 273 calls to Template::Stash::XS::set, avg 4µs/call
- -
36128114.9ms
3724711.89ms
38480193µs
- -
42199117.0ms
- -
44199116.0ms
 
# spent 1.10s within Template::Document::CORE:match which was called 384174 times, avg 3µs/call: # 384174 times (1.10s+0s) by Template::Document::new at line 75 of Template/Document.pm, avg 3µs/call
sub Template::Document::CORE:match; # opcode
# spent 1.36s within Template::Document::CORE:subst which was called 384174 times, avg 4µs/call: # 384174 times (1.36s+0s) by Template::Document::AUTOLOAD at line 187 of Template/Document.pm, avg 4µs/call
sub Template::Document::CORE:subst; # opcode
Report produced by the NYTProf 6.06 Perl profiler, developed by Tim Bunce based on work by Adam Kaplan and Salvador Fandiño García.